<?xml version="1.0" encoding="UTF-8"?>
<robot generator="Robot 7.4.2 (Python 3.11.10 on linux)" generated="2026-08-09T00:45:43.651103" 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-08-09T00:45:44.196971" elapsed="0.000024"/>
</return>
<status status="NOT RUN" start="2026-08-09T00:45:44.196832" elapsed="0.000215"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:44.196802" elapsed="0.000273"/>
</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-08-09T00:45:44.197220" elapsed="0.000517"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-08-09T00:45:44.198209" level="INFO">Logging into '10.30.171.74:8101' as 'karaf'.</msg>
<msg time="2026-08-09T00:45:44.784921" 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-08-09T00:45:44.197912" elapsed="0.587219"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-08-09T00:45:44.835084" 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-08-09T00:45:44.785436" elapsed="0.049812"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-08-09T00:45:44.838088" level="INFO">[?1l&gt;[?2004l[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-08-09T00:45:44.838278" 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-08-09T00:45:44.835534" elapsed="0.002793"/>
</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-08-09T00:45:44.838564" elapsed="0.000427"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T00:45:44.839233" elapsed="0.000302"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:45:44.840286" 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-08-09T00:45:44.839788" elapsed="0.000580"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-08-09T00:45:44.840446" elapsed="0.000074"/>
</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.74
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-08-09T00:45:44.196444" elapsed="0.644277"/>
</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-08-09T00:45:44.192511" elapsed="0.648306"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-08-09T00:45:44.841628" 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-08-09T00:45:44.841084" elapsed="0.000632"/>
</kw>
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-08-09T00:45:44.842327" 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-08-09T00:45:44.841951" elapsed="0.000474"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-08-09T00:45:44.843363" 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-08-09T00:45:44.842693" elapsed="0.000712"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-08-09T00:45:44.844079" 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-08-09T00:45:44.843687" elapsed="0.000436"/>
</kw>
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-08-09T00:45:44.844681" 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-08-09T00:45:44.844273" elapsed="0.000436"/>
</kw>
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-08-09T00:45:44.845194" 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-08-09T00:45:44.844876" elapsed="0.000362"/>
</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-08-09T00:45:44.845546" elapsed="0.000021"/>
</kw>
<status status="NOT RUN" start="2026-08-09T00:45:44.845326" elapsed="0.000294"/>
</branch>
<branch type="ELSE">
<kw name="Verify File Exists On Remote System" owner="Utils">
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-08-09T00:45:44.850242" 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-08-09T00:45:44.849826" elapsed="0.000444"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T00:45:44.851346" level="INFO">Length is 0.</msg>
<msg time="2026-08-09T00:45:44.851433" 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-08-09T00:45:44.850893" elapsed="0.000566"/>
</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-08-09T00:45:44.851626" elapsed="0.000363"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-08-09T00:45:44.853030" level="INFO">Logging into '10.30.170.133:22' as 'jenkins'.</msg>
<msg time="2026-08-09T00:45:45.698452" 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 Sun Aug  9 00:45:45 UTC 2026

  System load:  0.06               Processes:             110
  Usage of /:   19.3% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.133
  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-365-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-08-09T00:45:44.852498" elapsed="0.846127"/>
</kw>
<msg time="2026-08-09T00:45:45.698720" 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-08-09T00:45:44.852147" elapsed="0.846674"/>
</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-08-09T00:45:44.850479" elapsed="0.848465"/>
</kw>
<kw name="File Should Exist" owner="SSHLibrary">
<msg time="2026-08-09T00:45:45.708499" 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-08-09T00:45:45.699158" elapsed="0.011384"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T00:45:45.710816" elapsed="0.000385"/>
</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-08-09T00:45:44.849275" elapsed="0.862027"/>
</kw>
<status status="PASS" start="2026-08-09T00:45:44.845929" elapsed="0.865426"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:44.845300" elapsed="0.866103"/>
</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-08-09T00:45:45.711636" elapsed="0.000647"/>
</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-08-09T00:45:45.718516" elapsed="0.000025"/>
</return>
<status status="NOT RUN" start="2026-08-09T00:45:45.718348" elapsed="0.000259"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:45.718315" elapsed="0.000326"/>
</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-08-09T00:45:45.722650" elapsed="0.000014"/>
</return>
<status status="NOT RUN" start="2026-08-09T00:45:45.722520" elapsed="0.000177"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:45.722501" 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-08-09T00:45:45.722853" elapsed="0.000461"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-08-09T00:45:45.723741" level="INFO">Logging into '10.30.171.74:8101' as 'karaf'.</msg>
<msg time="2026-08-09T00:45:45.961645" 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-08-09T00:45:45.723464" elapsed="0.238273"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.064060" 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-08-09T00:45:45.961915" elapsed="0.102248"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.084344" 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-08-09T00:45:46.084549" 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-08-09T00:45:46.064339" elapsed="0.020258"/>
</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-08-09T00:45:46.084740" elapsed="0.000202"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T00:45:46.085092" elapsed="0.000200"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:45:46.085749" 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-08-09T00:45:46.085436" elapsed="0.000369"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-08-09T00:45:46.085852" elapsed="0.000043"/>
</return>
<msg time="2026-08-09T00:45:46.086030" 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.74
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-08-09T00:45:45.722162" elapsed="0.363899"/>
</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-08-09T00:45:46.086207" elapsed="0.000593"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-08-09T00:45:46.086852" 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-08-09T00:45:45.717930" elapsed="0.369054"/>
</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-08-09T00:45:46.090686" elapsed="0.000015"/>
</return>
<status status="NOT RUN" start="2026-08-09T00:45:46.090550" elapsed="0.000183"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:46.090529" elapsed="0.000227"/>
</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-08-09T00:45:46.090888" elapsed="0.000427"/>
</kw>
<kw name="Login" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.091769" level="INFO">Logging into '10.30.171.74:8101' as 'karaf'.</msg>
<msg time="2026-08-09T00:45:46.227597" 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-08-09T00:45:46.091463" elapsed="0.136225"/>
</kw>
<kw name="Write" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.250019" 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-08-09T00:45:46.227848" elapsed="0.022347"/>
</kw>
<kw name="Read Until Regexp" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.252350" level="INFO">[?1l&gt;[?2004lDropAllFlows transitions to on
[?1h=[90m~[0m                                                                                [?2004hopendaylight-user@root&gt;</msg>
<msg time="2026-08-09T00:45:46.252468" 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-08-09T00:45:46.250504" elapsed="0.001994"/>
</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-08-09T00:45:46.252654" elapsed="0.000353"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T00:45:46.253163" elapsed="0.000298"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:45:46.253994" 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-08-09T00:45:46.253635" elapsed="0.000411"/>
</kw>
<return>
<value>${output}</value>
<status status="PASS" start="2026-08-09T00:45:46.254103" elapsed="0.000067"/>
</return>
<arg>dropallpacketsrpc on</arg>
<doc>Will execute the given ${cmd} by ssh'ing to the karaf console running on 10.30.171.74
Note that this keyword will open&amp;close new SSH connection, without switching back to previously current session.</doc>
<status status="PASS" start="2026-08-09T00:45:46.090239" elapsed="0.164062"/>
</kw>
<status status="PASS" start="2026-08-09T00:45:44.190660" elapsed="2.063710"/>
</kw>
<test id="s1-t1" name="Cbench Latency Test" line="37">
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:45:46.256780" 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-08-09T00:45:46.255388" elapsed="0.001461"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-08-09T00:45:46.258380" 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-08-09T00:45:46.258015" elapsed="0.000409"/>
</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-08-09T00:45:46.258789" elapsed="0.000022"/>
</kw>
<status status="NOT RUN" start="2026-08-09T00:45:46.258509" elapsed="0.000339"/>
</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-08-09T00:45:46.262083" elapsed="0.000024"/>
</kw>
<status status="NOT RUN" start="2026-08-09T00:45:46.261761" elapsed="0.000386"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:46.261735" elapsed="0.000438"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.262624" 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-08-09T00:45:46.262766" 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-08-09T00:45:46.262320" elapsed="0.000474"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:45:46.263433" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.74 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.170.133" 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-08-09T00:45:46.262962" elapsed="0.000532"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.264195" 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-08-09T00:45:46.263675" elapsed="0.000546"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T00:45:46.265214" level="INFO">Length is 0.</msg>
<msg time="2026-08-09T00:45:46.265309" 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-08-09T00:45:46.264846" elapsed="0.000488"/>
</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-08-09T00:45:46.265483" elapsed="0.000381"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.266791" level="INFO">Logging into '10.30.170.133:22' as 'jenkins'.</msg>
<msg time="2026-08-09T00:45:46.606503" 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 Sun Aug  9 00:45:45 UTC 2026

  System load:  0.06               Processes:             110
  Usage of /:   19.3% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.133
  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: Sun Aug  9 00:45:45 2026 from 10.30.171.113
[?2004h[jenkins@releng-63164-365-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-08-09T00:45:46.266409" elapsed="0.340322"/>
</kw>
<msg time="2026-08-09T00:45:46.606826" 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-08-09T00:45:46.266056" elapsed="0.340879"/>
</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-08-09T00:45:46.264436" elapsed="0.342645"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-08-09T00:45:46.607760" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.74 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-08-09T00:55:52.747313" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-08-09T00:55:52.747825" level="INFO">${stdout} = </msg>
<msg time="2026-08-09T00:55:52.747928" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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-08-09T00:45:46.607348" elapsed="606.140638"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T00:55:52.748432" elapsed="0.000730"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.750349" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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.74. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:46:51.829 16  switches: flows:  108555 106985 107895 108202 108196 105935 107071 107776 105287 103535 102527 102176 105465 102872 102059 101738  total = 28.104567 per ms 
00:47:51.929 16  switches: flows:  118913 120925 120086 120811 119204 116875 119064 120104 114946 115162 114655 114084 115429 116273 113320 117239  total = 31.232687 per ms 
00:48:52.030 16  switches: flows:  115257 115514 114898 117156 113857 113482 113463 117305 111493 110932 111412 110821 112665 109563 111012 112903  total = 30.145231 per ms 
00:49:52.130 16  switches: flows:  119772 119514 119634 122655 117404 117758 119320 118815 113696 116347 115779 114331 116738 113674 113291 115581  total = 31.186422 per ms 
00:50:52.230 16  switches: flows:  126509 122862 123296 126634 122046 123066 123576 125771 118544 121237 122701 119027 121597 118273 117265 118795  total = 32.465663 per ms 
00:51:52.331 16  switches: flows:  126395 122791 120427 125561 120450 121743 122892 124042 116904 121493 122255 117489 119658 116759 115573 116840  total = 32.134231 per ms 
00:52:52.431 16  switches: flows:  115045 110993 111800 115115 110977 111174 112901 114560 107142 109822 111307 106759 110873 106237 106517 108689  total = 29.449309 per ms 
00:53:52.531 16  switches: flows:  118352 114815 114225 117568 113637 114030 115476 117506 112055 112801 114361 111361 113365 111288 110369 110287  total = 30.307678 per ms 
00:54:52.632 16  switches: flows:  113582 112744 112402 115275 111591 109267 109845 113799 111725 109247 112427 109012 112799 110543 110032 108694  total = 29.666877 per ms 
00:55:52.732 16  switches: flows:  112061 106181 108250 110421 107445 104231 104947 110128 103442 105606 107084 102684 107133 105011 102871 105151  total = 28.330141 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 28330.14/32465.66/30546.47/10259.30 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T00:55:52.749543" elapsed="0.001148"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-08-09T00:55:52.751179" elapsed="0.000072"/>
</return>
<status status="PASS" start="2026-08-09T00:55:52.750879" elapsed="0.000451"/>
</branch>
<status status="PASS" start="2026-08-09T00:55:52.750823" elapsed="0.000563"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-08-09T00:55:52.751633" elapsed="0.000032"/>
</return>
<status status="NOT RUN" start="2026-08-09T00:55:52.751517" elapsed="0.000210"/>
</branch>
<status status="NOT RUN" start="2026-08-09T00:55:52.751482" elapsed="0.000293"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-08-09T00:55:52.751847" elapsed="0.000030"/>
</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-08-09T00:55:52.756275" 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-08-09T00:55:52.756962" elapsed="0.000222"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T00:55:52.757364" elapsed="0.000153"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-08-09T00:55:52.752536" elapsed="0.005076"/>
</kw>
<msg time="2026-08-09T00:55:52.757790" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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-08-09T00:45:46.259219" elapsed="606.498608"/>
</kw>
<status status="PASS" start="2026-08-09T00:45:46.258880" elapsed="606.499014"/>
</branch>
<status status="PASS" start="2026-08-09T00:45:46.258484" elapsed="606.499443"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.758526" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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.74. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:46:51.829 16  switches: flows:  108555 106985 107895 108202 108196 105935 107071 107776 105287 103535 102527 102176 105465 102872 102059 101738  total = 28.104567 per ms 
00:47:51.929 16  switches: flows:  118913 120925 120086 120811 119204 116875 119064 120104 114946 115162 114655 114084 115429 116273 113320 117239  total = 31.232687 per ms 
00:48:52.030 16  switches: flows:  115257 115514 114898 117156 113857 113482 113463 117305 111493 110932 111412 110821 112665 109563 111012 112903  total = 30.145231 per ms 
00:49:52.130 16  switches: flows:  119772 119514 119634 122655 117404 117758 119320 118815 113696 116347 115779 114331 116738 113674 113291 115581  total = 31.186422 per ms 
00:50:52.230 16  switches: flows:  126509 122862 123296 126634 122046 123066 123576 125771 118544 121237 122701 119027 121597 118273 117265 118795  total = 32.465663 per ms 
00:51:52.331 16  switches: flows:  126395 122791 120427 125561 120450 121743 122892 124042 116904 121493 122255 117489 119658 116759 115573 116840  total = 32.134231 per ms 
00:52:52.431 16  switches: flows:  115045 110993 111800 115115 110977 111174 112901 114560 107142 109822 111307 106759 110873 106237 106517 108689  total = 29.449309 per ms 
00:53:52.531 16  switches: flows:  118352 114815 114225 117568 113637 114030 115476 117506 112055 112801 114361 111361 113365 111288 110369 110287  total = 30.307678 per ms 
00:54:52.632 16  switches: flows:  113582 112744 112402 115275 111591 109267 109845 113799 111725 109247 112427 109012 112799 110543 110032 108694  total = 29.666877 per ms 
00:55:52.732 16  switches: flows:  112061 106181 108250 110421 107445 104231 104947 110128 103442 105606 107084 102684 107133 105011 102871 105151  total = 28.330141 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 28330.14/32465.66/30546.47/10259.30 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T00:55:52.758118" elapsed="0.000533"/>
</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-08-09T00:55:52.758855" elapsed="0.000506"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-08-09T00:55:52.759968" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-08-09T00:55:52.760098" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 28330.14/32465.66/30546.47/10259.30 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-08-09T00:55:52.759646" elapsed="0.000487"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T00:55:52.760725" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 28330.14/32465.66/30546.47/10259.30 | 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-08-09T00:55:52.760345" elapsed="0.000418"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.764170" 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-08-09T00:55:52.760963" elapsed="0.003286"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.767329" level="INFO">28330.14/32465.66/30546.47/10259.30</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T00:55:52.764457" elapsed="0.002933"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T00:55:52.769745" 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-08-09T00:55:52.767538" elapsed="0.002235"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T00:55:52.772156" level="INFO">@{result_value_list} = [ 28330.14 | 32465.66 | 30546.47 | 10259.30 ]</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-08-09T00:55:52.769957" elapsed="0.002227"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.772661" level="INFO">Length is 4.</msg>
<msg time="2026-08-09T00:55:52.772751" 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-08-09T00:55:52.772337" elapsed="0.000438"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.777446" level="INFO">min :: 28330.14</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-08-09T00:55:52.773152" elapsed="0.004377"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-08-09T00:55:52.773022" elapsed="0.004543"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.782162" level="INFO">max :: 32465.66</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-08-09T00:55:52.777805" elapsed="0.004416"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-08-09T00:55:52.777677" elapsed="0.004580"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.786802" level="INFO">avg :: 30546.47</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-08-09T00:55:52.782480" elapsed="0.004378"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-08-09T00:55:52.782348" elapsed="0.004544"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.791369" level="INFO">stdev :: 10259.30</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-08-09T00:55:52.787110" elapsed="0.004367"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-08-09T00:55:52.786983" elapsed="0.004528"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-08-09T00:55:52.772840" elapsed="0.018703"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.795522" level="INFO">${min} = 28330.14</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-08-09T00:55:52.791722" elapsed="0.003827"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.799595" level="INFO">${max} = 32465.66</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-08-09T00:55:52.795723" elapsed="0.003900"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.803733" level="INFO">${average} = 30546.47</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-08-09T00:55:52.799963" elapsed="0.003797"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.807745" level="INFO">${stdev} = 10259.30</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-08-09T00:55:52.803910" elapsed="0.003861"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.808282" level="INFO">${date} = 2026-08-09 00:55:52</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-08-09T00:55:52.807930" elapsed="0.000377"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.808884" level="INFO">CBench Result: 2026-08-09 00:55:52,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,28330.14,32465.66,30546.47,10259.30</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-08-09T00:55:52.808458" elapsed="0.000482"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-08-09T00:55:52.809980" 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-08-09T00:55:52.809583" elapsed="0.000462"/>
</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-08-09T00:55:52.810193" elapsed="0.000518"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-08-09T00:55:52.809082" elapsed="0.001693"/>
</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-08-09T00:45:46.257326" elapsed="606.553509"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.811304" 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-08-09T00:55:52.810984" 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-08-09T00:55:52.811887" elapsed="0.000266"/>
</kw>
<status status="PASS" start="2026-08-09T00:55:52.811584" elapsed="0.000640"/>
</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-08-09T00:45:46.254602" elapsed="606.557658"/>
</test>
<test id="s1-t2" name="Cbench Throughput Test" line="51">
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.813885" 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-08-09T00:55:52.813455" elapsed="0.000487"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.815431" 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-08-09T00:55:52.815100" elapsed="0.000373"/>
</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-08-09T00:55:52.815820" elapsed="0.000020"/>
</kw>
<status status="NOT RUN" start="2026-08-09T00:55:52.815598" elapsed="0.000279"/>
</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-08-09T00:55:52.818783" elapsed="0.000021"/>
</kw>
<status status="NOT RUN" start="2026-08-09T00:55:52.818523" elapsed="0.000316"/>
</branch>
<status status="PASS" start="2026-08-09T00:55:52.818504" elapsed="0.000358"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-08-09T00:55:52.819194" 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-08-09T00:55:52.819313" 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-08-09T00:55:52.818997" elapsed="0.000342"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.819909" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.74 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.170.133" 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-08-09T00:55:52.819473" elapsed="0.000494"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-08-09T00:55:52.820499" 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-08-09T00:55:52.820106" elapsed="0.000419"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T00:55:52.821428" level="INFO">Length is 0.</msg>
<msg time="2026-08-09T00:55:52.821541" 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-08-09T00:55:52.821114" elapsed="0.000467"/>
</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-08-09T00:55:52.821723" elapsed="0.000330"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-08-09T00:55:52.822915" level="INFO">Logging into '10.30.170.133:22' as 'jenkins'.</msg>
<msg time="2026-08-09T00:55:53.386957" 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 Sun Aug  9 00:55:52 UTC 2026

  System load:  1.07               Processes:             104
  Usage of /:   19.8% of 38.58GB   Users logged in:       0
  Memory usage: 4%                 IPv4 address for ens3: 10.30.170.133
  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: Sun Aug  9 00:45:46 2026 from 10.30.171.113
[?2004h[jenkins@releng-63164-365-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-08-09T00:55:52.822545" elapsed="0.564604"/>
</kw>
<msg time="2026-08-09T00:55:53.387238" 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-08-09T00:55:52.822203" elapsed="0.565129"/>
</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-08-09T00:55:52.820741" elapsed="0.566730"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-08-09T00:55:53.388084" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.74 -t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-08-09T01:05:59.459734" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-08-09T01:05:59.460382" level="INFO">${stdout} = </msg>
<msg time="2026-08-09T01:05:59.460498" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.74: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-08-09T00:55:53.387742" elapsed="606.072815"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T01:05:59.461047" elapsed="0.000752"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.463013" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.74: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.74. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:56:58.521 16  switches: flows:  469086 482262 404771 463282 474204 463920 478302 473938 469523 462570 470222 473553 460122 467174 454582 469037  total = 123.942467 per ms 
00:57:58.623 16  switches: flows:  495004 494115 495393 496832 496942 493831 488816 491840 494473 490799 493255 493658 498106 502682 500251 498924  total = 131.858929 per ms 
00:58:58.725 16  switches: flows:  458310 459566 460478 460834 449971 463919 463021 462020 461405 458866 464119 466107 463672 456746 455432 460592  total = 122.543388 per ms 
00:59:58.826 16  switches: flows:  450471 452800 441524 443112 449332 444527 448245 448626 453956 452142 449517 449681 449766 451484 446265 453377  total = 119.546880 per ms 
01:00:58.929 16  switches: flows:  477698 475113 481455 475450 478893 476506 475436 475956 472880 478778 477905 479099 476543 478456 476507 478511  total = 127.038312 per ms 
01:01:59.030 16  switches: flows:  476130 479403 483861 480089 476127 471657 475543 472068 475670 475372 480654 471082 475766 480265 468177 478068  total = 126.785295 per ms 
01:02:59.131 16  switches: flows:  488473 492675 494539 486882 464936 492232 492070 492438 494869 495411 491341 491232 485753 490855 494222 485737  total = 130.341627 per ms 
01:03:59.233 16  switches: flows:  533491 528761 536614 534395 536478 530496 525712 535618 537342 530990 536075 531126 532245 538426 533270 533195  total = 141.996124 per ms 
01:04:59.334 16  switches: flows:  513831 498102 498089 505963 513641 504123 504373 505776 491505 508591 512891 499684 499905 514770 504152 503814  total = 134.427668 per ms 
01:05:59.440 16  switches: flows:  514977 512867 512245 517840 513891 507213 510149 516753 510491 518263 514144 516826 511119 516435 514010 516983  total = 136.837777 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 119546.88/141996.12/130152.89/43890.49 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T01:05:59.462161" elapsed="0.001158"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-08-09T01:05:59.463861" elapsed="0.000071"/>
</return>
<status status="PASS" start="2026-08-09T01:05:59.463523" elapsed="0.000491"/>
</branch>
<status status="PASS" start="2026-08-09T01:05:59.463468" elapsed="0.000606"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-08-09T01:05:59.464320" elapsed="0.000030"/>
</return>
<status status="NOT RUN" start="2026-08-09T01:05:59.464231" elapsed="0.000183"/>
</branch>
<status status="NOT RUN" start="2026-08-09T01:05:59.464190" elapsed="0.000278"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-08-09T01:05:59.464550" elapsed="0.000067"/>
</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-08-09T01:05:59.469744" elapsed="0.000345"/>
</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-08-09T01:05:59.470245" elapsed="0.000175"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T01:05:59.470553" elapsed="0.000133"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-08-09T01:05:59.465288" elapsed="0.005455"/>
</kw>
<msg time="2026-08-09T01:05:59.470883" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.74: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-08-09T00:55:52.816216" elapsed="606.654695"/>
</kw>
<status status="PASS" start="2026-08-09T00:55:52.815901" elapsed="606.655059"/>
</branch>
<status status="PASS" start="2026-08-09T00:55:52.815562" elapsed="606.655421"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.471420" level="INFO">cbench: controller benchmarking tool
   running in mode 'throughput'
   connecting to controller at 10.30.171.74: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.74. So far 1 cbench instances have initialized their switches.  Waiting for other cbench-instances
Thread-0: Total switches from ALL cbench-instances initialized.
00:56:58.521 16  switches: flows:  469086 482262 404771 463282 474204 463920 478302 473938 469523 462570 470222 473553 460122 467174 454582 469037  total = 123.942467 per ms 
00:57:58.623 16  switches: flows:  495004 494115 495393 496832 496942 493831 488816 491840 494473 490799 493255 493658 498106 502682 500251 498924  total = 131.858929 per ms 
00:58:58.725 16  switches: flows:  458310 459566 460478 460834 449971 463919 463021 462020 461405 458866 464119 466107 463672 456746 455432 460592  total = 122.543388 per ms 
00:59:58.826 16  switches: flows:  450471 452800 441524 443112 449332 444527 448245 448626 453956 452142 449517 449681 449766 451484 446265 453377  total = 119.546880 per ms 
01:00:58.929 16  switches: flows:  477698 475113 481455 475450 478893 476506 475436 475956 472880 478778 477905 479099 476543 478456 476507 478511  total = 127.038312 per ms 
01:01:59.030 16  switches: flows:  476130 479403 483861 480089 476127 471657 475543 472068 475670 475372 480654 471082 475766 480265 468177 478068  total = 126.785295 per ms 
01:02:59.131 16  switches: flows:  488473 492675 494539 486882 464936 492232 492070 492438 494869 495411 491341 491232 485753 490855 494222 485737  total = 130.341627 per ms 
01:03:59.233 16  switches: flows:  533491 528761 536614 534395 536478 530496 525712 535618 537342 530990 536075 531126 532245 538426 533270 533195  total = 141.996124 per ms 
01:04:59.334 16  switches: flows:  513831 498102 498089 505963 513641 504123 504373 505776 491505 508591 512891 499684 499905 514770 504152 503814  total = 134.427668 per ms 
01:05:59.440 16  switches: flows:  514977 512867 512245 517840 513891 507213 510149 516753 510491 518263 514144 516826 511119 516435 514010 516983  total = 136.837777 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 119546.88/141996.12/130152.89/43890.49 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T01:05:59.471121" elapsed="0.000375"/>
</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-08-09T01:05:59.471673" elapsed="0.000376"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-08-09T01:05:59.472456" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-08-09T01:05:59.472549" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 119546.88/141996.12/130152.89/43890.49 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-08-09T01:05:59.472222" elapsed="0.000369"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T01:05:59.473015" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 119546.88/141996.12/130152.89/43890.49 | 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-08-09T01:05:59.472752" elapsed="0.000291"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.475460" 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-08-09T01:05:59.473186" elapsed="0.002333"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.477943" level="INFO">119546.88/141996.12/130152.89/43890.49</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T01:05:59.475684" elapsed="0.002317"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T01:05:59.480410" 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-08-09T01:05:59.478148" elapsed="0.002290"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T01:05:59.482877" level="INFO">@{result_value_list} = [ 119546.88 | 141996.12 | 130152.89 | 43890.49 ]</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-08-09T01:05:59.480639" elapsed="0.002267"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.483371" level="INFO">Length is 4.</msg>
<msg time="2026-08-09T01:05:59.483461" 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-08-09T01:05:59.483060" elapsed="0.000426"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.488401" level="INFO">min :: 119546.88</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-08-09T01:05:59.483890" elapsed="0.004570"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-08-09T01:05:59.483755" elapsed="0.004741"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.493134" level="INFO">max :: 141996.12</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-08-09T01:05:59.488740" elapsed="0.004452"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-08-09T01:05:59.488607" elapsed="0.004621"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.497888" level="INFO">avg :: 130152.89</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-08-09T01:05:59.493445" elapsed="0.004501"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-08-09T01:05:59.493318" elapsed="0.004662"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.502556" level="INFO">stdev :: 43890.49</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-08-09T01:05:59.498210" elapsed="0.004421"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-08-09T01:05:59.498079" elapsed="0.004588"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-08-09T01:05:59.483551" elapsed="0.019147"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.506964" level="INFO">${min} = 119546.88</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-08-09T01:05:59.502854" elapsed="0.004138"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.510926" level="INFO">${max} = 141996.12</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-08-09T01:05:59.507154" elapsed="0.003799"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.514970" level="INFO">${average} = 130152.89</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-08-09T01:05:59.511106" elapsed="0.003891"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.518943" level="INFO">${stdev} = 43890.49</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-08-09T01:05:59.515147" elapsed="0.003823"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.519511" level="INFO">${date} = 2026-08-09 01:05:59</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-08-09T01:05:59.519143" elapsed="0.000395"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.520135" level="INFO">CBench Result: 2026-08-09 01:05:59,-t -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,119546.88,141996.12,130152.89,43890.49</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-08-09T01:05:59.519707" elapsed="0.000488"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-08-09T01:05:59.521231" 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-08-09T01:05:59.520848" elapsed="0.000451"/>
</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-08-09T01:05:59.521455" 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-08-09T01:05:59.520337" elapsed="0.001669"/>
</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-08-09T00:55:52.814409" elapsed="606.707656"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.522540" 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-08-09T01:05:59.522213" elapsed="0.000404"/>
</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-08-09T01:05:59.523123" elapsed="0.000271"/>
</kw>
<status status="PASS" start="2026-08-09T01:05:59.522822" elapsed="0.000645"/>
</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-08-09T00:55:52.812803" elapsed="606.710703"/>
</test>
<test id="s1-t3" name="Rerun Latency Test To Check Bug 6176" line="65">
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.524918" 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-08-09T01:05:59.524506" elapsed="0.000473"/>
</kw>
<kw name="Run Cbench And Log Results">
<kw name="Set Suite Variable" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.526228" 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-08-09T01:05:59.525890" elapsed="0.000383"/>
</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-08-09T01:05:59.526791" elapsed="0.000022"/>
</kw>
<status status="NOT RUN" start="2026-08-09T01:05:59.526550" elapsed="0.000300"/>
</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-08-09T01:05:59.529961" elapsed="0.000022"/>
</kw>
<status status="NOT RUN" start="2026-08-09T01:05:59.529709" elapsed="0.000310"/>
</branch>
<status status="PASS" start="2026-08-09T01:05:59.529689" elapsed="0.000354"/>
</if>
<kw name="Get Connection" owner="SSHLibrary">
<msg time="2026-08-09T01:05:59.530362" 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-08-09T01:05:59.530483" 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-08-09T01:05:59.530176" elapsed="0.000335"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.531109" level="INFO">Attempting to execute command "/usr/local/bin/cbench -c 10.30.171.74 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633" on remote system "10.30.170.133" 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-08-09T01:05:59.530676" elapsed="0.000494"/>
</kw>
<kw name="Open Connection" owner="SSHLibrary">
<msg time="2026-08-09T01:05:59.531745" 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-08-09T01:05:59.531313" elapsed="0.000460"/>
</kw>
<kw name="Flexible_SSH_Login" owner="SSHKeywords">
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T01:05:59.532726" level="INFO">Length is 0.</msg>
<msg time="2026-08-09T01:05:59.532821" 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-08-09T01:05:59.532385" elapsed="0.000462"/>
</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-08-09T01:05:59.532991" elapsed="0.000336"/>
</kw>
<kw name="Run Keyword And Return" owner="BuiltIn">
<kw name="Login With Public Key" owner="SSHLibrary">
<msg time="2026-08-09T01:05:59.534221" level="INFO">Logging into '10.30.170.133:22' as 'jenkins'.</msg>
<msg time="2026-08-09T01:06:00.131525" 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 Sun Aug  9 01:05:59 UTC 2026

  System load:  0.46               Processes:             104
  Usage of /:   19.8% of 38.58GB   Users logged in:       0
  Memory usage: 5%                 IPv4 address for ens3: 10.30.170.133
  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: Sun Aug  9 00:55:53 2026 from 10.30.171.113
[?2004h[jenkins@releng-63164-365-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-08-09T01:05:59.533861" elapsed="0.597830"/>
</kw>
<msg time="2026-08-09T01:06:00.131761" 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-08-09T01:05:59.533480" elapsed="0.598355"/>
</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-08-09T01:05:59.531981" elapsed="0.599977"/>
</kw>
<kw name="Execute Command" owner="SSHLibrary">
<msg time="2026-08-09T01:06:00.132411" level="INFO">Executing command '/usr/local/bin/cbench -c 10.30.171.74 -m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633'.</msg>
<msg time="2026-08-09T01:16:06.183407" level="INFO">Command exited with return code 0.</msg>
<msg time="2026-08-09T01:16:06.184002" level="INFO">${stdout} = </msg>
<msg time="2026-08-09T01:16:06.184109" level="INFO">${stderr} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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-08-09T01:06:00.132150" elapsed="606.052015"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T01:16:06.184697" elapsed="0.000748"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.186624" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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.74. 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:05.258 16  switches: flows:  106706 105258 104510 106545 103491 106636 105243 105409 104605 103870 100522 105219 104971 103656 103805 105589  total = 27.933917 per ms 
01:08:05.358 16  switches: flows:  113472 114930 116779 116268 114284 117728 114366 112262 114567 113982 111546 116280 115899 113042 114085 116568  total = 30.549945 per ms 
01:09:05.458 16  switches: flows:  114884 114322 115673 113847 113180 115844 113058 109631 116415 112400 110709 114250 114062 111364 114625 113964  total = 30.253297 per ms 
01:10:05.558 16  switches: flows:  110046 111470 115245 110822 112475 112576 110866 108717 112160 110027 110656 111813 113059 109130 112034 112339  total = 29.674383 per ms 
01:11:05.659 16  switches: flows:  113852 112437 114891 113656 113635 114365 111530 111469 115319 112785 112631 115258 115169 110844 115064 116664  total = 30.275612 per ms 
01:12:05.759 16  switches: flows:  115266 114714 115769 114928 113868 117015 113889 112354 117449 113373 115343 116069 114841 112214 115849 117338  total = 30.620206 per ms 
01:13:05.859 16  switches: flows:  113524 116265 113989 116989 116283 112827 110636 113610 114115 109702 114871 112421 110494 113332 112351 114687  total = 30.217841 per ms 
01:14:05.959 16  switches: flows:  111493 113574 111607 114636 114341 109680 109152 110334 111917 106397 112698 108247 108517 112253 110049 107839  total = 29.496331 per ms 
01:15:06.059 16  switches: flows:  109972 116057 113607 116555 117346 110401 110102 114136 111197 108378 115120 109860 110352 111579 111205 111224  total = 29.901609 per ms 
01:16:06.160 16  switches: flows:  114328 117019 112589 116798 117432 112734 113491 114832 115807 109851 113944 109700 111453 114124 113670 112054  total = 30.279887 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 29496.33/30620.21/30141.01/10053.32 responses/s</msg>
<arg>${stderr}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T01:16:06.185838" elapsed="0.001092"/>
</kw>
<if>
<branch type="IF" condition="&quot;${return_stdout}&quot;!=&quot;True&quot;">
<return>
<value>${stderr}</value>
<status status="PASS" start="2026-08-09T01:16:06.187412" elapsed="0.000068"/>
</return>
<status status="PASS" start="2026-08-09T01:16:06.187116" elapsed="0.000442"/>
</branch>
<status status="PASS" start="2026-08-09T01:16:06.187066" elapsed="0.000578"/>
</if>
<if>
<branch type="IF" condition="&quot;${return_stderr}&quot;!=&quot;True&quot;">
<return>
<value>${stdout}</value>
<status status="NOT RUN" start="2026-08-09T01:16:06.187848" elapsed="0.000027"/>
</return>
<status status="NOT RUN" start="2026-08-09T01:16:06.187769" elapsed="0.000166"/>
</branch>
<status status="NOT RUN" start="2026-08-09T01:16:06.187735" elapsed="0.000246"/>
</if>
<return>
<value>${stdout}</value>
<value>${stderr}</value>
<status status="NOT RUN" start="2026-08-09T01:16:06.188045" elapsed="0.000028"/>
</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-08-09T01:16:06.193274" elapsed="0.000384"/>
</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-08-09T01:16:06.193810" elapsed="0.000177"/>
</kw>
<kw name="Close Connection" owner="SSHLibrary">
<doc>Closes the current connection.</doc>
<status status="PASS" start="2026-08-09T01:16:06.194119" elapsed="0.000102"/>
</kw>
<arg>${current_ssh_connection.index}</arg>
<doc>Restore active SSH connection in SSHLibrary to given index.</doc>
<status status="PASS" start="2026-08-09T01:16:06.188769" elapsed="0.005507"/>
</kw>
<msg time="2026-08-09T01:16:06.194410" level="INFO">${output} = cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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-08-09T01:05:59.527192" elapsed="606.667246"/>
</kw>
<status status="PASS" start="2026-08-09T01:05:59.526876" elapsed="606.667612"/>
</branch>
<status status="PASS" start="2026-08-09T01:05:59.526526" elapsed="606.667987"/>
</if>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.194995" level="INFO">cbench: controller benchmarking tool
   running in mode 'latency'
   connecting to controller at 10.30.171.74: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.74. 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:05.258 16  switches: flows:  106706 105258 104510 106545 103491 106636 105243 105409 104605 103870 100522 105219 104971 103656 103805 105589  total = 27.933917 per ms 
01:08:05.358 16  switches: flows:  113472 114930 116779 116268 114284 117728 114366 112262 114567 113982 111546 116280 115899 113042 114085 116568  total = 30.549945 per ms 
01:09:05.458 16  switches: flows:  114884 114322 115673 113847 113180 115844 113058 109631 116415 112400 110709 114250 114062 111364 114625 113964  total = 30.253297 per ms 
01:10:05.558 16  switches: flows:  110046 111470 115245 110822 112475 112576 110866 108717 112160 110027 110656 111813 113059 109130 112034 112339  total = 29.674383 per ms 
01:11:05.659 16  switches: flows:  113852 112437 114891 113656 113635 114365 111530 111469 115319 112785 112631 115258 115169 110844 115064 116664  total = 30.275612 per ms 
01:12:05.759 16  switches: flows:  115266 114714 115769 114928 113868 117015 113889 112354 117449 113373 115343 116069 114841 112214 115849 117338  total = 30.620206 per ms 
01:13:05.859 16  switches: flows:  113524 116265 113989 116989 116283 112827 110636 113610 114115 109702 114871 112421 110494 113332 112351 114687  total = 30.217841 per ms 
01:14:05.959 16  switches: flows:  111493 113574 111607 114636 114341 109680 109152 110334 111917 106397 112698 108247 108517 112253 110049 107839  total = 29.496331 per ms 
01:15:06.059 16  switches: flows:  109972 116057 113607 116555 117346 110401 110102 114136 111197 108378 115120 109860 110352 111579 111205 111224  total = 29.901609 per ms 
01:16:06.160 16  switches: flows:  114328 117019 112589 116798 117432 112734 113491 114832 115807 109851 113944 109700 111453 114124 113670 112054  total = 30.279887 per ms 
RESULT: 16 switches 9 tests min/max/avg/stdev = 29496.33/30620.21/30141.01/10053.32 responses/s</msg>
<arg>${output}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T01:16:06.194687" elapsed="0.000383"/>
</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-08-09T01:16:06.195217" elapsed="0.000382"/>
</kw>
<kw name="Get Lines Containing String" owner="String">
<msg time="2026-08-09T01:16:06.195981" level="INFO">1 out of 23 lines matched.</msg>
<msg time="2026-08-09T01:16:06.196071" level="INFO">${result_line} = RESULT: 16 switches 9 tests min/max/avg/stdev = 29496.33/30620.21/30141.01/10053.32 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-08-09T01:16:06.195761" elapsed="0.000334"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T01:16:06.196511" level="INFO">@{results_list} = [ RESULT: | 16 | switches | 9 | tests | min/max/avg/stdev | = | 29496.33/30620.21/30141.01/10053.32 | 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-08-09T01:16:06.196259" elapsed="0.000278"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.198925" 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-08-09T01:16:06.196698" elapsed="0.002283"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.201341" level="INFO">29496.33/30620.21/30141.01/10053.32</msg>
<arg>${results_list[7]}</arg>
<doc>Logs the given message with the given level.</doc>
<status status="PASS" start="2026-08-09T01:16:06.199124" elapsed="0.002272"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T01:16:06.203754" 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-08-09T01:16:06.201542" elapsed="0.002238"/>
</kw>
<kw name="Split String" owner="String">
<msg time="2026-08-09T01:16:06.206228" level="INFO">@{result_value_list} = [ 29496.33 | 30620.21 | 30141.01 | 10053.32 ]</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-08-09T01:16:06.203986" elapsed="0.002269"/>
</kw>
<kw name="Get Length" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.206729" level="INFO">Length is 4.</msg>
<msg time="2026-08-09T01:16:06.206816" 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-08-09T01:16:06.206406" elapsed="0.000434"/>
</kw>
<for flavor="IN RANGE">
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.211520" level="INFO">min :: 29496.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-08-09T01:16:06.207225" elapsed="0.004365"/>
</kw>
<var name="${i}">0</var>
<status status="PASS" start="2026-08-09T01:16:06.207099" elapsed="0.004527"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.216322" level="INFO">max :: 30620.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-08-09T01:16:06.211839" elapsed="0.004540"/>
</kw>
<var name="${i}">1</var>
<status status="PASS" start="2026-08-09T01:16:06.211714" elapsed="0.004700"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.220944" level="INFO">avg :: 30141.01</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-08-09T01:16:06.216638" elapsed="0.004363"/>
</kw>
<var name="${i}">2</var>
<status status="PASS" start="2026-08-09T01:16:06.216497" elapsed="0.004538"/>
</iter>
<iter>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.225869" level="INFO">stdev :: 10053.32</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-08-09T01:16:06.221244" elapsed="0.004688"/>
</kw>
<var name="${i}">3</var>
<status status="PASS" start="2026-08-09T01:16:06.221118" elapsed="0.004849"/>
</iter>
<var>${i}</var>
<value>0</value>
<value>${num_stats}</value>
<status status="PASS" start="2026-08-09T01:16:06.206910" elapsed="0.019088"/>
</for>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.229895" level="INFO">${min} = 29496.33</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-08-09T01:16:06.226164" elapsed="0.003758"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.233883" level="INFO">${max} = 30620.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-08-09T01:16:06.230076" elapsed="0.003834"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.237936" level="INFO">${average} = 30141.01</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-08-09T01:16:06.234065" elapsed="0.003899"/>
</kw>
<kw name="Set Variable" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.241868" level="INFO">${stdev} = 10053.32</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-08-09T01:16:06.238121" elapsed="0.003773"/>
</kw>
<kw name="Get Time" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.242406" level="INFO">${date} = 2026-08-09 01:16:06</msg>
<var>${date}</var>
<arg>d,m,s</arg>
<doc>Returns the given time in the requested format.</doc>
<status status="PASS" start="2026-08-09T01:16:06.242054" elapsed="0.000378"/>
</kw>
<kw name="Log" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.243003" level="INFO">CBench Result: 2026-08-09 01:16:06,-m 60000 -M 100 -S 16 -l 10 -D 5000 -p 6633,29496.33,30620.21,30141.01,10053.32</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-08-09T01:16:06.242590" elapsed="0.000470"/>
</kw>
<kw name="Log Results And Determine Status">
<kw name="Append To File" owner="OperatingSystem">
<msg time="2026-08-09T01:16:06.244230" 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-08-09T01:16:06.243715" elapsed="0.000584"/>
</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-08-09T01:16:06.244456" elapsed="0.000522"/>
</kw>
<arg>${min}</arg>
<arg>${max}</arg>
<arg>${average}</arg>
<arg>${average_threshold}</arg>
<arg>${output_filename}</arg>
<status status="PASS" start="2026-08-09T01:16:06.243218" elapsed="0.001826"/>
</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-08-09T01:05:59.525298" elapsed="606.719805"/>
</kw>
<kw name="Sleep" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.245560" 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-08-09T01:16:06.245250" elapsed="0.000384"/>
</kw>
<kw name="Report Failure Due To Bug" owner="Utils" type="TEARDOWN">
<kw name="Evaluate" owner="BuiltIn">
<msg time="2026-08-09T01:16:06.246856" 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-08-09T01:16:06.246352" elapsed="0.000531"/>
</kw>
<if>
<branch type="IF" condition="('${TEST_STATUS}' != 'FAIL') or ${test_skipped}">
<return>
<status status="PASS" start="2026-08-09T01:16:06.247121" elapsed="0.000037"/>
</return>
<status status="PASS" start="2026-08-09T01:16:06.246967" elapsed="0.000224"/>
</branch>
<status status="PASS" start="2026-08-09T01:16:06.246945" elapsed="0.000271"/>
</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-08-09T01:16:06.247362" elapsed="0.000021"/>
</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-08-09T01:16:06.247517" elapsed="0.000020"/>
</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-08-09T01:16:06.247701" elapsed="0.000021"/>
</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-08-09T01:16:06.247908" elapsed="0.000020"/>
</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-08-09T01:16:06.248058" 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-08-09T01:16:06.248221" elapsed="0.000020"/>
</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-08-09T01:16:06.248365" elapsed="0.000020"/>
</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-08-09T01:16:06.248606" elapsed="0.000021"/>
</kw>
<status status="NOT RUN" start="2026-08-09T01:16:06.248446" elapsed="0.000213"/>
</branch>
<status status="NOT RUN" start="2026-08-09T01:16:06.248430" elapsed="0.000252"/>
</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-08-09T01:16:06.246016" elapsed="0.002716"/>
</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-08-09T01:05:59.523935" elapsed="606.724843"/>
</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-08-09T00:45:43.652672" elapsed="1822.596836"/>
</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>
